[INFO] cloning repository https://github.com/IgrisBRC/mini_redis
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/IgrisBRC/mini_redis" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FIgrisBRC%2Fmini_redis", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FIgrisBRC%2Fmini_redis'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 246557a5b54e1da97fe3f4408835bf49f505102a
[INFO] testing IgrisBRC/mini_redis against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FIgrisBRC%2Fmini_redis" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/IgrisBRC/mini_redis
[INFO] finished tweaking git repo https://github.com/IgrisBRC/mini_redis
[INFO] tweaked toml for git repo https://github.com/IgrisBRC/mini_redis written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/IgrisBRC/mini_redis on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/IgrisBRC/mini_redis already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 3de867ed8de129afd6b2c80cc84a0546925ca47e200cbcc7daccab1045e9d573
[INFO] running `Command { std: "docker" "start" "-a" "3de867ed8de129afd6b2c80cc84a0546925ca47e200cbcc7daccab1045e9d573", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3de867ed8de129afd6b2c80cc84a0546925ca47e200cbcc7daccab1045e9d573", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3de867ed8de129afd6b2c80cc84a0546925ca47e200cbcc7daccab1045e9d573", kill_on_drop: false }`
[INFO] [stdout] 3de867ed8de129afd6b2c80cc84a0546925ca47e200cbcc7daccab1045e9d573
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 80f236dfd5765c9ff6f014e919a31bc4427b7416c0e1d6565bd3aba12a9d42f7
[INFO] running `Command { std: "docker" "start" "-a" "80f236dfd5765c9ff6f014e919a31bc4427b7416c0e1d6565bd3aba12a9d42f7", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling cfg_aliases v0.2.1
[INFO] [stderr]    Compiling simdutf8 v0.1.5
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling jemalloc-sys v0.5.4+5.3.0-patched
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling ctrlc v3.5.2
[INFO] [stderr]    Compiling ptr_meta_derive v0.3.1
[INFO] [stderr]    Compiling bytecheck_derive v0.8.2
[INFO] [stderr]    Compiling munge_macro v0.4.7
[INFO] [stderr]    Compiling rkyv_derive v0.8.15
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling munge v0.4.7
[INFO] [stderr]    Compiling ptr_meta v0.3.1
[INFO] [stderr]    Compiling rancor v0.1.1
[INFO] [stderr]    Compiling bytecheck v0.8.2
[INFO] [stderr]    Compiling rend v0.5.3
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling rkyv v0.8.15
[INFO] [stderr]    Compiling jemallocator v0.5.4
[INFO] [stderr]    Compiling jerusalem v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 47s
[INFO] running `Command { std: "docker" "inspect" "80f236dfd5765c9ff6f014e919a31bc4427b7416c0e1d6565bd3aba12a9d42f7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "80f236dfd5765c9ff6f014e919a31bc4427b7416c0e1d6565bd3aba12a9d42f7", kill_on_drop: false }`
[INFO] [stdout] 80f236dfd5765c9ff6f014e919a31bc4427b7416c0e1d6565bd3aba12a9d42f7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7692c069fe3806687f801e8cd185fdabddbab9890dafb4bf0d93cc7734925261
[INFO] running `Command { std: "docker" "start" "-a" "7692c069fe3806687f801e8cd185fdabddbab9890dafb4bf0d93cc7734925261", kill_on_drop: false }`
[INFO] [stderr]    Compiling jerusalem v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.87s
[INFO] running `Command { std: "docker" "inspect" "7692c069fe3806687f801e8cd185fdabddbab9890dafb4bf0d93cc7734925261", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7692c069fe3806687f801e8cd185fdabddbab9890dafb4bf0d93cc7734925261", kill_on_drop: false }`
[INFO] [stdout] 7692c069fe3806687f801e8cd185fdabddbab9890dafb4bf0d93cc7734925261
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f33d465b89ad23b65d88aae375ae3c1e66796749d3ea266ac0e43bf5d010704a
[INFO] running `Command { std: "docker" "start" "-a" "f33d465b89ad23b65d88aae375ae3c1e66796749d3ea266ac0e43bf5d010704a", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/jerusalem-edb169239491183e)
[INFO] [stdout] 
[INFO] [stdout] running 85 tests
[INFO] [stdout] test tests::integration_test::test_del_returns_count ... FAILED
[INFO] [stdout] test tests::integration_test::test_incr_on_non_integer_returns_error ... FAILED
[INFO] [stdout] test tests::integration_test::test_list_operations ... FAILED
[INFO] [stdout] test tests::integration_test::test_exists ... FAILED
[INFO] [stdout] test tests::integration_test::test_hash_operations ... FAILED
[INFO] [stdout] test tests::integration_test::test_append ... FAILED
[INFO] [stdout] test tests::integration_test::test_lpop_rpop_with_count ... FAILED
[INFO] [stdout] test tests::integration_test::test_incr_decr ... FAILED
[INFO] [stdout] test tests::integration_test::test_ping ... FAILED
[INFO] [stdout] test tests::integration_test::test_set_and_get ... FAILED
[INFO] [stdout] test tests::integration_test::test_mset_mget ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_unsubscribe_all ... FAILED
[INFO] [stdout] test tests::soul_test::append_to_existing_key_concatenates ... ok
[INFO] [stdout] test tests::soul_test::append_to_missing_key_creates_it ... ok
[INFO] [stdout] test tests::integration_test::test_set_with_ex_expires ... FAILED
[INFO] [stdout] test tests::integration_test::test_expire_and_ttl ... FAILED
[INFO] [stdout] test tests::soul_test::decr_existing_integer ... ok
[INFO] [stdout] test tests::soul_test::decr_non_integer_returns_error ... ok
[INFO] [stdout] test tests::soul_test::del_removes_key ... ok
[INFO] [stdout] test tests::soul_test::exists_returns_count_of_present_keys ... ok
[INFO] [stdout] test tests::soul_test::expire_missing_key_returns_zero ... ok
[INFO] [stdout] test tests::soul_test::exists_counts_duplicate_keys_multiple_times ... ok
[INFO] [stdout] test tests::soul_test::decr_missing_key_starts_at_minus_one ... ok
[INFO] [stdout] test tests::soul_test::del_existing_keys_returns_count ... ok
[INFO] [stdout] test tests::soul_test::get_expired_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::get_wrong_type_returns_error ... ok
[INFO] [stdout] test tests::soul_test::expire_sets_expiry_on_key ... ok
[INFO] [stdout] test tests::soul_test::hexists_missing_field_returns_zero ... ok
[INFO] [stdout] test tests::soul_test::hexists_present_field_returns_one ... ok
[INFO] [stdout] test tests::soul_test::hget_missing_field_returns_none ... ok
[INFO] [stdout] test tests::soul_test::hget_missing_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::hgetall_returns_flat_interleaved_field_value_list ... ok
[INFO] [stdout] test tests::soul_test::hlen_returns_number_of_fields ... ok
[INFO] [stdout] test tests::soul_test::hmget_returns_values_in_order_with_nones_for_missing ... ok
[INFO] [stdout] test tests::soul_test::hset_creates_fields_and_returns_added_count ... ok
[INFO] [stdout] test tests::soul_test::hdel_removes_fields_and_returns_count ... ok
[INFO] [stdout] test tests::soul_test::hset_update_existing_field_does_not_increment_count ... ok
[INFO] [stdout] test tests::soul_test::incr_non_integer_returns_error ... ok
[INFO] [stdout] test tests::soul_test::lindex_negative_index ... ok
[INFO] [stdout] test tests::soul_test::lindex_out_of_range_returns_none ... ok
[INFO] [stdout] test tests::soul_test::lindex_valid_index ... ok
[INFO] [stdout] test tests::soul_test::llen_missing_key_is_zero ... ok
[INFO] [stdout] test tests::soul_test::lpop_empty_list_key_deleted ... ok
[INFO] [stdout] test tests::soul_test::lpop_m_pops_n_elements ... ok
[INFO] [stdout] test tests::soul_test::lpop_missing_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::lpop_removes_and_returns_head ... ok
[INFO] [stdout] test tests::soul_test::incr_missing_key_starts_at_one ... ok
[INFO] [stdout] test tests::soul_test::lrange_full_range ... ok
[INFO] [stdout] test tests::soul_test::lrange_negative_indices ... ok
[INFO] [stdout] test tests::soul_test::lrange_out_of_bounds_returns_empty ... ok
[INFO] [stdout] test tests::soul_test::lrem_count_zero_removes_all ... ok
[INFO] [stdout] test tests::soul_test::lrem_negative_count_removes_from_tail ... ok
[INFO] [stdout] test tests::soul_test::lrem_removes_from_head ... ok
[INFO] [stdout] test tests::soul_test::lset_out_of_range_returns_error ... ok
[INFO] [stdout] test tests::soul_test::lset_replaces_element ... ok
[INFO] [stdout] test tests::soul_test::rpop_m_pops_n_elements_from_tail ... ok
[INFO] [stdout] test tests::soul_test::rpop_removes_and_returns_tail ... ok
[INFO] [stdout] test tests::soul_test::rpush_appends_in_order ... ok
[INFO] [stdout] test tests::soul_test::sadd_adds_new_members_returns_count ... ok
[INFO] [stdout] test tests::soul_test::sadd_duplicate_not_counted ... ok
[INFO] [stdout] test tests::pubsub_test::test_subscribe_multiple_channels ... FAILED
[INFO] [stdout] test tests::soul_test::lpush_creates_list_and_prepends ... ok
[INFO] [stdout] test tests::soul_test::sismember_absent_returns_zero ... ok
[INFO] [stdout] test tests::soul_test::sismember_present_returns_one ... ok
[INFO] [stdout] test tests::integration_test::test_expire_missing_key_returns_zero ... FAILED
[INFO] [stdout] test tests::soul_test::strlen_existing_string ... ok
[INFO] [stdout] test tests::soul_test::strlen_missing_key_is_zero ... ok
[INFO] [stdout] test tests::soul_test::srem_removes_members_returns_count ... ok
[INFO] [stdout] test tests::soul_test::strlen_wrong_type_returns_error ... ok
[INFO] [stdout] test tests::soul_test::set_then_get_returns_value ... ok
[INFO] [stdout] test tests::soul_test::ttl_missing_key_returns_minus_two ... ok
[INFO] [stdout] test tests::soul_test::ttl_returns_remaining_seconds ... ok
[INFO] [stdout] test tests::soul_test::append_wrong_type_returns_error ... ok
[INFO] [stdout] test tests::soul_test::get_missing_key_returns_none ... ok
[INFO] [stdout] test tests::integration_test::test_get_missing_key_returns_null ... FAILED
[INFO] [stdout] test tests::integration_test::test_wrongtype_errors ... FAILED
[INFO] [stdout] test tests::integration_test::test_strlen ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_ping_in_subscriber_mode ... FAILED
[INFO] [stdout] test tests::integration_test::test_set_operations ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_publish_no_subscribers_returns_zero ... FAILED
[INFO] [stdout] test tests::soul_test::incr_existing_integer ... ok
[INFO] [stdout] test tests::soul_test::smembers_returns_all_members ... ok
[INFO] [stdout] test tests::pubsub_test::test_subscriber_mode_rejects_regular_commands ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_subscribe_receives_published_message ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_multiple_subscribers_all_receive_message has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "f33d465b89ad23b65d88aae375ae3c1e66796749d3ea266ac0e43bf5d010704a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f33d465b89ad23b65d88aae375ae3c1e66796749d3ea266ac0e43bf5d010704a", kill_on_drop: false }`
[INFO] [stdout] f33d465b89ad23b65d88aae375ae3c1e66796749d3ea266ac0e43bf5d010704a
